bng-test/usr/local/etc/raddb# cat users
000c.29eb.eb96          Cleartext-Password := "123"
                        ERX-Service-Activate:1 += "RATE-LIMIT(1000000)"

000c.297c.ea3f          Cleartext-Password := "123"
                        ERX-Service-Activate:1 += "RATE-LIMIT(1000000)"

# user1                 Cleartext-Password := "pass1"
# spirent0              Cleartext-Password := "spirent0"
# spirent1              Cleartext-Password := "spirent1"

===

rad_recv: Access-Request packet from host 192.168.3.74 port 53233, id=75, length=369
        User-Name = "000c.29eb.eb96"
        User-Password = "123"
        Service-Type = Framed-User
        Chargeable-User-Identity = ""
        Acct-Session-Id = "146"
        Calling-Station-Id = "MX104-RE0:xe-0/0/0.1073741903:11"
        ERX-Dhcp-Options = 0x3501013204c0a800650c09667265656273642d343705011c030f06524101184558343330302d3332463a65742d302f312f302e303a3131021c66343a62353a32663a34633a31393a30303a65742d302f312f302e3006074a756e69706572
        ERX-Dhcp-Mac-Addr = "000c.29eb.eb96"
        Framed-IP-Address = 192.168.0.101
        ERX-Interface-Desc = "-- L2 access network"
        NAS-Identifier = "MX104-RE0"
        NAS-Port = 11
        NAS-Port-Id = "xe-0/0/0.1073741903:11"
        NAS-Port-Type = Ethernet
        ADSL-Agent-Circuit-Id = "EX4300-32F:et-0/1/0.0:11"
        ADSL-Agent-Remote-Id = "f4:b5:2f:4c:19:00:et-0/1/0.0"
# Executing section authorize from file /usr/local/etc/raddb/sites-enabled/default
+group authorize {
++[preprocess] = ok
++[chap] = noop
++[mschap] = noop
++[digest] = noop
[suffix] No '@' in User-Name = "000c.29eb.eb96", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] = noop
[eap] No EAP-Message, not doing EAP
++[eap] = noop
[files] users: Matched entry 000c.29eb.eb96 at line 1
++[files] = ok
++[expiration] = noop
++[logintime] = noop
++[pap] = updated
+} # group authorize = updated
Found Auth-Type = PAP
# Executing group from file /usr/local/etc/raddb/sites-enabled/default
+group PAP {
[pap] login attempt with password "123"
[pap] Using clear text password "123"
[pap] User authenticated successfully
++[pap] = ok
+} # group PAP = ok
Login OK: [000c.29eb.eb96/123] (from client mx104-RE0 port 11 cli MX104-RE0:xe-0/0/0.1073741903:11)
# Executing section post-auth from file /usr/local/etc/raddb/sites-enabled/default
+group post-auth {
++[exec] = noop
+} # group post-auth = noop
Sending Access-Accept of id 75 to 192.168.3.74 port 53233
        ERX-Service-Activate:1 += "RATE-LIMIT(1000000)"
Finished request 0.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Accounting-Request packet from host 192.168.3.74 port 53233, id=76, length=407
        User-Name = "000c.29eb.eb96"
        Acct-Status-Type = Start
        Acct-Session-Id = "146"
        Service-Type = Framed-User
        ERX-Attr-177 = 0x506f72742053706565643a2031303030303030306b
        Acct-Authentic = RADIUS
        Acct-Delay-Time = 0
        Calling-Station-Id = "MX104-RE0:xe-0/0/0.1073741903:11"
        ERX-Dhcp-Options = 0x3501013204c0a800650c09667265656273642d343705011c030f06524101184558343330302d3332463a65742d302f312f302e303a3131021c66343a62353a32663a34633a31393a30303a65742d302f312f302e3006074a756e69706572
        ERX-Dhcp-Mac-Addr = "000c.29eb.eb96"
        Event-Timestamp = "Oct 17 2014 11:21:01 UTC"
        Framed-IP-Address = 192.168.0.101
        Framed-IP-Netmask = 255.255.255.0
        ERX-Interface-Desc = "-- L2 access network"
        NAS-Identifier = "MX104-RE0"
        NAS-Port = 11
        NAS-Port-Id = "xe-0/0/0.1073741903:11"
        NAS-Port-Type = Ethernet
        ADSL-Agent-Circuit-Id = "EX4300-32F:et-0/1/0.0:11"
        ADSL-Agent-Remote-Id = "f4:b5:2f:4c:19:00:et-0/1/0.0"
# Executing section preacct from file /usr/local/etc/raddb/sites-enabled/default
+group preacct {
++[preprocess] = ok
[acct_unique] Hashing 'NAS-Port = 11,NAS-Identifier = "MX104-RE0",NAS-IP-Address = 192.168.3.74,Acct-Session-Id = "146",User-Name = "000c.29eb.eb96"'
[acct_unique] Acct-Unique-Session-ID = "09393a69afc9b8db".
++[acct_unique] = ok
[suffix] No '@' in User-Name = "000c.29eb.eb96", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] = noop
++[files] = noop
+} # group preacct = ok
# Executing section accounting from file /usr/local/etc/raddb/sites-enabled/default
+group accounting {
[detail]        expand: %{Packet-Src-IP-Address} -> 192.168.3.74
[detail]        expand: /var/log/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d -> /var/log/radacct/192.168.3.74/detail-20141017
[detail] /var/log/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d expands to /var/log/radacct/192.168.3.74/detail-20141017
[detail]        expand: %t -> Fri Oct 17 15:40:03 2014
++[detail] = ok
++[exec] = noop
[attr_filter.accounting_response]       expand: %{User-Name} -> 000c.29eb.eb96
attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] = updated
+} # group accounting = updated
Sending Accounting-Response of id 76 to 192.168.3.74 port 53233
Finished request 1.
Cleaning up request 1 ID 76 with timestamp +151
Going to the next request
Waking up in 4.5 seconds.
rad_recv: Accounting-Request packet from host 192.168.3.74 port 53233, id=77, length=545
        User-Name = "000c.29eb.eb96"
        Acct-Status-Type = Interim-Update
        Acct-Session-Id = "146"
        Acct-Input-Octets = 0
        Acct-Output-Octets = 0
        Acct-Session-Time = 1
        Acct-Input-Packets = 0
        Acct-Output-Packets = 0
        Service-Type = Framed-User
        ERX-Attr-177 = 0x506f72742053706565643a2031303030303030306b
        Acct-Authentic = RADIUS
        Acct-Delay-Time = 0
        Calling-Station-Id = "MX104-RE0:xe-0/0/0.1073741903:11"
        ERX-Dhcp-Options = 0x3501013204c0a800650c09667265656273642d343705011c030f06524101184558343330302d3332463a65742d302f312f302e303a3131021c66343a62353a32663a34633a31393a30303a65742d302f312f302e3006074a756e69706572
        ERX-Dhcp-Mac-Addr = "000c.29eb.eb96"
        Event-Timestamp = "Oct 17 2014 11:21:02 UTC"
        Framed-IP-Address = 192.168.0.101
        Framed-IP-Netmask = 255.255.255.0
        ERX-Input-Gigapkts = 0
        Acct-Input-Gigawords = 0
        ERX-Interface-Desc = "-- L2 access network"
        NAS-Identifier = "MX104-RE0"
        NAS-Port = 11
        NAS-Port-Id = "xe-0/0/0.1073741903:11"
        NAS-Port-Type = Ethernet
        ERX-Output-Gigapkts = 0
        Acct-Output-Gigawords = 0
        ERX-IPv6-Acct-Input-Octets = 0
        ERX-IPv6-Acct-Output-Octets = 0
        ERX-IPv6-Acct-Input-Packets = 0
        ERX-IPv6-Acct-Output-Packets = 0
        ERX-IPv6-Acct-Input-Gigawords = 0
        ERX-IPv6-Acct-Output-Gigawords = 0
        ADSL-Agent-Circuit-Id = "EX4300-32F:et-0/1/0.0:11"
        ADSL-Agent-Remote-Id = "f4:b5:2f:4c:19:00:et-0/1/0.0"
# Executing section preacct from file /usr/local/etc/raddb/sites-enabled/default
+group preacct {
++[preprocess] = ok
[acct_unique] Hashing 'NAS-Port = 11,NAS-Identifier = "MX104-RE0",NAS-IP-Address = 192.168.3.74,Acct-Session-Id = "146",User-Name = "000c.29eb.eb96"'
[acct_unique] Acct-Unique-Session-ID = "09393a69afc9b8db".
++[acct_unique] = ok
[suffix] No '@' in User-Name = "000c.29eb.eb96", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] = noop
++[files] = noop
+} # group preacct = ok
# Executing section accounting from file /usr/local/etc/raddb/sites-enabled/default
+group accounting {
[detail]        expand: %{Packet-Src-IP-Address} -> 192.168.3.74
[detail]        expand: /var/log/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d -> /var/log/radacct/192.168.3.74/detail-20141017
[detail] /var/log/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d expands to /var/log/radacct/192.168.3.74/detail-20141017
[detail]        expand: %t -> Fri Oct 17 15:40:03 2014
++[detail] = ok
++[exec] = noop
[attr_filter.accounting_response]       expand: %{User-Name} -> 000c.29eb.eb96
attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] = updated
+} # group accounting = updated
Sending Accounting-Response of id 77 to 192.168.3.74 port 53233
Finished request 2.
Cleaning up request 2 ID 77 with timestamp +151
Going to the next request
Waking up in 4.0 seconds.
Cleaning up request 0 ID 75 with timestamp +150
Ready to process requests.
rad_recv: Access-Request packet from host 192.168.3.74 port 53233, id=78, length=369
        User-Name = "000c.297c.ea3f"
        User-Password = "123"
        Service-Type = Framed-User
        Chargeable-User-Identity = ""
        Acct-Session-Id = "148"
        Calling-Station-Id = "MX104-RE0:xe-0/0/0.1073741903:11"
        ERX-Dhcp-Options = 0x3501013204c0a8006f0c09667265656273642d353705011c030f06524101184558343330302d3332463a65742d302f312f302e303a3131021c66343a62353a32663a34633a31393a30303a65742d302f312f302e3006074a756e69706572
        ERX-Dhcp-Mac-Addr = "000c.297c.ea3f"
        Framed-IP-Address = 192.168.0.111
        ERX-Interface-Desc = "-- L2 access network"
        NAS-Identifier = "MX104-RE0"
        NAS-Port = 11
        NAS-Port-Id = "xe-0/0/0.1073741903:11"
        NAS-Port-Type = Ethernet
        ADSL-Agent-Circuit-Id = "EX4300-32F:et-0/1/0.0:11"
        ADSL-Agent-Remote-Id = "f4:b5:2f:4c:19:00:et-0/1/0.0"
# Executing section authorize from file /usr/local/etc/raddb/sites-enabled/default
+group authorize {
++[preprocess] = ok
++[chap] = noop
++[mschap] = noop
++[digest] = noop
[suffix] No '@' in User-Name = "000c.297c.ea3f", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] = noop
[eap] No EAP-Message, not doing EAP
++[eap] = noop
[files] users: Matched entry 000c.297c.ea3f at line 4
++[files] = ok
++[expiration] = noop
++[logintime] = noop
++[pap] = updated
+} # group authorize = updated
Found Auth-Type = PAP
# Executing group from file /usr/local/etc/raddb/sites-enabled/default
+group PAP {
[pap] login attempt with password "123"
[pap] Using clear text password "123"
[pap] User authenticated successfully
++[pap] = ok
+} # group PAP = ok
Login OK: [000c.297c.ea3f/123] (from client mx104-RE0 port 11 cli MX104-RE0:xe-0/0/0.1073741903:11)
# Executing section post-auth from file /usr/local/etc/raddb/sites-enabled/default
+group post-auth {
++[exec] = noop
+} # group post-auth = noop
Sending Access-Accept of id 78 to 192.168.3.74 port 53233
        ERX-Service-Activate:1 += "RATE-LIMIT(1000000)"
Finished request 3.
Going to the next request
Waking up in 4.9 seconds.
rad_recv: Accounting-Request packet from host 192.168.3.74 port 53233, id=79, length=407
        User-Name = "000c.297c.ea3f"
        Acct-Status-Type = Start
        Acct-Session-Id = "148"
        Service-Type = Framed-User
        ERX-Attr-177 = 0x506f72742053706565643a2031303030303030306b
        Acct-Authentic = RADIUS
        Acct-Delay-Time = 0
        Calling-Station-Id = "MX104-RE0:xe-0/0/0.1073741903:11"
        ERX-Dhcp-Options = 0x3501013204c0a8006f0c09667265656273642d353705011c030f06524101184558343330302d3332463a65742d302f312f302e303a3131021c66343a62353a32663a34633a31393a30303a65742d302f312f302e3006074a756e69706572
        ERX-Dhcp-Mac-Addr = "000c.297c.ea3f"
        Event-Timestamp = "Oct 17 2014 11:21:08 UTC"
        Framed-IP-Address = 192.168.0.111
        Framed-IP-Netmask = 255.255.255.0
        ERX-Interface-Desc = "-- L2 access network"
        NAS-Identifier = "MX104-RE0"
        NAS-Port = 11
        NAS-Port-Id = "xe-0/0/0.1073741903:11"
        NAS-Port-Type = Ethernet
        ADSL-Agent-Circuit-Id = "EX4300-32F:et-0/1/0.0:11"
        ADSL-Agent-Remote-Id = "f4:b5:2f:4c:19:00:et-0/1/0.0"
# Executing section preacct from file /usr/local/etc/raddb/sites-enabled/default
+group preacct {
++[preprocess] = ok
[acct_unique] Hashing 'NAS-Port = 11,NAS-Identifier = "MX104-RE0",NAS-IP-Address = 192.168.3.74,Acct-Session-Id = "148",User-Name = "000c.297c.ea3f"'
[acct_unique] Acct-Unique-Session-ID = "d64707c910b00021".
++[acct_unique] = ok
[suffix] No '@' in User-Name = "000c.297c.ea3f", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] = noop
++[files] = noop
+} # group preacct = ok
# Executing section accounting from file /usr/local/etc/raddb/sites-enabled/default
+group accounting {
[detail]        expand: %{Packet-Src-IP-Address} -> 192.168.3.74
[detail]        expand: /var/log/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d -> /var/log/radacct/192.168.3.74/detail-20141017
[detail] /var/log/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d expands to /var/log/radacct/192.168.3.74/detail-20141017
[detail]        expand: %t -> Fri Oct 17 15:40:09 2014
++[detail] = ok
++[exec] = noop
[attr_filter.accounting_response]       expand: %{User-Name} -> 000c.297c.ea3f
attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] = updated
+} # group accounting = updated
Sending Accounting-Response of id 79 to 192.168.3.74 port 53233
Finished request 4.
Cleaning up request 4 ID 79 with timestamp +157
Going to the next request
Waking up in 4.7 seconds.
rad_recv: Accounting-Request packet from host 192.168.3.74 port 53233, id=80, length=545
        User-Name = "000c.297c.ea3f"
        Acct-Status-Type = Interim-Update
        Acct-Session-Id = "148"
        Acct-Input-Octets = 0
        Acct-Output-Octets = 0
        Acct-Session-Time = 1
        Acct-Input-Packets = 0
        Acct-Output-Packets = 0
        Service-Type = Framed-User
        ERX-Attr-177 = 0x506f72742053706565643a2031303030303030306b
        Acct-Authentic = RADIUS
        Acct-Delay-Time = 0
        Calling-Station-Id = "MX104-RE0:xe-0/0/0.1073741903:11"
        ERX-Dhcp-Options = 0x3501013204c0a8006f0c09667265656273642d353705011c030f06524101184558343330302d3332463a65742d302f312f302e303a3131021c66343a62353a32663a34633a31393a30303a65742d302f312f302e3006074a756e69706572
        ERX-Dhcp-Mac-Addr = "000c.297c.ea3f"
        Event-Timestamp = "Oct 17 2014 11:21:09 UTC"
        Framed-IP-Address = 192.168.0.111
        Framed-IP-Netmask = 255.255.255.0
        ERX-Input-Gigapkts = 0
        Acct-Input-Gigawords = 0
        ERX-Interface-Desc = "-- L2 access network"
        NAS-Identifier = "MX104-RE0"
        NAS-Port = 11
        NAS-Port-Id = "xe-0/0/0.1073741903:11"
        NAS-Port-Type = Ethernet
        ERX-Output-Gigapkts = 0
        Acct-Output-Gigawords = 0
        ERX-IPv6-Acct-Input-Octets = 0
        ERX-IPv6-Acct-Output-Octets = 0
        ERX-IPv6-Acct-Input-Packets = 0
        ERX-IPv6-Acct-Output-Packets = 0
        ERX-IPv6-Acct-Input-Gigawords = 0
        ERX-IPv6-Acct-Output-Gigawords = 0
        ADSL-Agent-Circuit-Id = "EX4300-32F:et-0/1/0.0:11"
        ADSL-Agent-Remote-Id = "f4:b5:2f:4c:19:00:et-0/1/0.0"
# Executing section preacct from file /usr/local/etc/raddb/sites-enabled/default
+group preacct {
++[preprocess] = ok
[acct_unique] Hashing 'NAS-Port = 11,NAS-Identifier = "MX104-RE0",NAS-IP-Address = 192.168.3.74,Acct-Session-Id = "148",User-Name = "000c.297c.ea3f"'
[acct_unique] Acct-Unique-Session-ID = "d64707c910b00021".
++[acct_unique] = ok
[suffix] No '@' in User-Name = "000c.297c.ea3f", looking up realm NULL
[suffix] No such realm "NULL"
++[suffix] = noop
++[files] = noop
+} # group preacct = ok
# Executing section accounting from file /usr/local/etc/raddb/sites-enabled/default
+group accounting {
[detail]        expand: %{Packet-Src-IP-Address} -> 192.168.3.74
[detail]        expand: /var/log/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d -> /var/log/radacct/192.168.3.74/detail-20141017
[detail] /var/log/radacct/%{%{Packet-Src-IP-Address}:-%{Packet-Src-IPv6-Address}}/detail-%Y%m%d expands to /var/log/radacct/192.168.3.74/detail-20141017
[detail]        expand: %t -> Fri Oct 17 15:40:10 2014
++[detail] = ok
++[exec] = noop
[attr_filter.accounting_response]       expand: %{User-Name} -> 000c.297c.ea3f
attr_filter: Matched entry DEFAULT at line 12
++[attr_filter.accounting_response] = updated
+} # group accounting = updated
Sending Accounting-Response of id 80 to 192.168.3.74 port 53233
Finished request 5.
Cleaning up request 5 ID 80 with timestamp +158
Going to the next request
Waking up in 4.2 seconds.
Cleaning up request 3 ID 78 with timestamp +157
Ready to process requests.
